[INFO] cloning repository https://github.com/tajhlande/cmdify
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/tajhlande/cmdify" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftajhlande%2Fcmdify", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftajhlande%2Fcmdify'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 1c53147519e79ca96f4e13d392f90d1b474effc0
[INFO] testing tajhlande/cmdify against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftajhlande%2Fcmdify" "/workspace/builds/worker-6-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-6-tc1/source/.cargo/config.toml
[INFO] removed /workspace/builds/worker-6-tc1/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/tajhlande/cmdify
[INFO] finished tweaking git repo https://github.com/tajhlande/cmdify
[INFO] tweaked toml for git repo https://github.com/tajhlande/cmdify written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/tajhlande/cmdify on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/tajhlande/cmdify already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded deadpool v0.12.3
[INFO] [stderr]   Downloaded toml_writer v1.1.1+spec-1.1.0
[INFO] [stderr]   Downloaded serde_spanned v1.1.1
[INFO] [stderr]   Downloaded toml v1.1.2+spec-1.1.0
[INFO] [stderr]   Downloaded endian-type v0.2.0
[INFO] [stderr]   Downloaded wiremock v0.6.5
[INFO] [stderr]   Downloaded winnow v1.0.1
[INFO] [stderr]   Downloaded rustyline v18.0.0
[INFO] [stderr]   Downloaded radix_trie v0.3.0
[INFO] [stderr]   Downloaded nix v0.31.3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 646639a0727176e798bf8341c7f0de8cec1404069dca52d5b569fde4f92bc82f
[INFO] running `Command { std: "docker" "start" "-a" "646639a0727176e798bf8341c7f0de8cec1404069dca52d5b569fde4f92bc82f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "646639a0727176e798bf8341c7f0de8cec1404069dca52d5b569fde4f92bc82f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "646639a0727176e798bf8341c7f0de8cec1404069dca52d5b569fde4f92bc82f", kill_on_drop: false }`
[INFO] [stdout] 646639a0727176e798bf8341c7f0de8cec1404069dca52d5b569fde4f92bc82f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b5507e9d358b3c820123095d73fa6b3c9a779fbf34eb411fa698eb57b704be17
[INFO] running `Command { std: "docker" "start" "-a" "b5507e9d358b3c820123095d73fa6b3c9a779fbf34eb411fa698eb57b704be17", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling shlex v1.3.0
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling rustls v0.23.37
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling nibble_vec v0.1.0
[INFO] [stderr]    Compiling icu_locale_core v2.2.0
[INFO] [stderr]    Compiling winnow v1.0.1
[INFO] [stderr]    Compiling endian-type v0.2.0
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling radix_trie v0.3.0
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling toml_writer v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling home v0.5.12
[INFO] [stderr]    Compiling unicode-segmentation v1.13.2
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling toml_parser v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling toml v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling jobserver v0.1.34
[INFO] [stderr]    Compiling cc v1.2.58
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling cmake v0.1.58
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling aws-lc-sys v0.39.1
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling rustyline v18.0.0
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling aws-lc-rs v1.16.2
[INFO] [stderr]    Compiling rustls-webpki v0.103.10
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling rustls-platform-verifier v0.6.2
[INFO] [stderr]    Compiling hyper-rustls v0.27.7
[INFO] [stderr]    Compiling reqwest v0.13.2
[INFO] [stderr]    Compiling cmdify v0.12.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 59.19s
[INFO] running `Command { std: "docker" "inspect" "b5507e9d358b3c820123095d73fa6b3c9a779fbf34eb411fa698eb57b704be17", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b5507e9d358b3c820123095d73fa6b3c9a779fbf34eb411fa698eb57b704be17", kill_on_drop: false }`
[INFO] [stdout] b5507e9d358b3c820123095d73fa6b3c9a779fbf34eb411fa698eb57b704be17
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e7a6ba12edbc3e7b83de0c67f23bf9d7e29096cca2800133c6cb375f9252d7e0
[INFO] running `Command { std: "docker" "start" "-a" "e7a6ba12edbc3e7b83de0c67f23bf9d7e29096cca2800133c6cb375f9252d7e0", kill_on_drop: false }`
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling num_cpus v1.17.0
[INFO] [stderr]    Compiling deadpool-runtime v0.1.4
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling assert-json-diff v2.0.2
[INFO] [stderr]    Compiling deadpool v0.12.3
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling rustyline v18.0.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling hyper-rustls v0.27.7
[INFO] [stderr]    Compiling wiremock v0.6.5
[INFO] [stderr]    Compiling reqwest v0.13.2
[INFO] [stderr]    Compiling cmdify v0.12.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 45.96s
[INFO] running `Command { std: "docker" "inspect" "e7a6ba12edbc3e7b83de0c67f23bf9d7e29096cca2800133c6cb375f9252d7e0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e7a6ba12edbc3e7b83de0c67f23bf9d7e29096cca2800133c6cb375f9252d7e0", kill_on_drop: false }`
[INFO] [stdout] e7a6ba12edbc3e7b83de0c67f23bf9d7e29096cca2800133c6cb375f9252d7e0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ab2a2af7ab6bfe2816112010335003c7fb727b4e6ef7005ede29cad1728ad68b
[INFO] running `Command { std: "docker" "start" "-a" "ab2a2af7ab6bfe2816112010335003c7fb727b4e6ef7005ede29cad1728ad68b", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.38s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/cmdify-78c41ac5f8551832)
[INFO] [stdout] 
[INFO] [stdout] running 371 tests
[INFO] [stdout] test app::tests::overrides_apply_blind ... ok
[INFO] [stdout] test app::tests::overrides_apply_debug ... ok
[INFO] [stdout] test app::tests::overrides_apply_no_tools ... ok
[INFO] [stdout] test app::tests::overrides_apply_yolo ... hello
[INFO] [stdout] ok
[INFO] [stdout] test app::tests::safety_gate_allows_echo_of_dangerous ... ok
[INFO] [stdout] test app::tests::safety_gate_allows_safe_command ... ok
[INFO] [stdout] test app::tests::safety_gate_blocks_dangerous_command ... ok
[INFO] [stdout] test app::tests::default_tool_level_source_added ... ok
[INFO] [stderr] sh: 1: nonexistent_command_xyz_123: not found
[INFO] [stdout] test app::tests::safety_gate_allows_unsafe_mode ... ok
[INFO] [stdout] test app::tests::safety_gate_blocks_pipe_to_shell ... ok
[INFO] [stdout] test app::tests::debug_level_maxed_with_existing ... ok
[INFO] [stdout] test app::tests::no_overrides_leaves_config_unchanged ... ok
[INFO] [stdout] test app::tests::overrides_apply_tool_level ... ok
[INFO] [stdout] test app::tests::execute_command_output ... ok
[INFO] [stdout] test app::tests::execute_command_success ... ok
[INFO] [stdout] test app::tests::execute_command_failure ... ok
[INFO] [stdout] test app::tests::tool_level_clamped_to_3 ... ok
[INFO] [stdout] test app::tests::tool_level_cli_overrides_env_level ... ok
[INFO] [stdout] test app::tests::yolo_or_with_existing ... ok
[INFO] [stdout] test app::tests::execute_command_nonexistent_command_fails ... ok
[INFO] [stdout] test cli::tests::config_default_none ... ok
[INFO] [stdout] test cli::tests::interactive_conflicts_with_prompt ... ok
[INFO] [stdout] test cli::tests::parse_basic_prompt ... ok
[INFO] [stdout] test cli::tests::debug_default_zero ... ok
[INFO] [stdout] test cli::tests::parse_blind_flag ... ok
[INFO] [stdout] test cli::tests::interactive_default_false ... ok
[INFO] [stdout] test cli::tests::parse_config_long ... ok
[INFO] [stdout] test cli::tests::list_tools_default_false ... ok
[INFO] [stdout] test cli::tests::no_args_returns_no_prompt ... ok
[INFO] [stdout] test cli::tests::parse_debug_long ... ok
[INFO] [stdout] test cli::tests::help_is_generated ... ok
[INFO] [stdout] test cli::tests::parse_debug_short ... ok
[INFO] [stdout] test cli::tests::help_includes_interactive ... ok
[INFO] [stdout] test cli::tests::parse_no_tools_flag ... ok
[INFO] [stdout] test cli::tests::parse_quiet_flag ... ok
[INFO] [stdout] test cli::tests::parse_setup_long ... ok
[INFO] [stdout] test cli::tests::interactive_conflicts_with_setup ... ok
[INFO] [stdout] test cli::tests::parse_list_tools ... ok
[INFO] [stdout] test cli::tests::parse_interactive_short ... ok
[INFO] [stdout] test cli::tests::parse_long_flags ... ok
[INFO] [stdout] test cli::tests::parse_spinner_long ... ok
[INFO] [stdout] test cli::tests::parse_interactive_long ... ok
[INFO] [stdout] test cli::tests::parse_debug_verbose ... ok
[INFO] [stdout] test cli::tests::parse_spinner_short ... ok
[INFO] [stdout] test cli::tests::parse_config_short ... ok
[INFO] [stdout] test cli::tests::parse_tool_level_2 ... ok
[INFO] [stdout] test cli::tests::parse_tool_level_long ... ok
[INFO] [stdout] test cli::tests::parse_tool_level_short ... ok
[INFO] [stdout] test cli::tests::parse_yolo_flag ... ok
[INFO] [stdout] test cli::tests::parse_unsafe_long ... ok
[INFO] [stdout] test cli::tests::parse_unsafe_short ... ok
[INFO] [stdout] test cli::tests::setup_default_false ... ok
[INFO] [stdout] test cli::tests::tool_level_default_none ... ok
[INFO] [stdout] test config::tests::bool_flags_false_from_env ... ok
[INFO] [stdout] test cli::tests::parse_yolo_long_flag ... ok
[INFO] [stdout] test cli::tests::spinner_default ... ok
[INFO] [stdout] test cli::tests::help_includes_setup ... ok
[INFO] [stdout] test config::tests::cli_config_overrides_env_config ... ok
[INFO] [stdout] test config::tests::bool_flags_from_config_file ... ok
[INFO] [stdout] test config::tests::bool_flags_env_overrides_config ... ok
[INFO] [stdout] test config::tests::bool_flags_from_env ... ok
[INFO] [stdout] test config::tests::all_flags_from_config_file ... ok
[INFO] [stdout] test config::tests::cmdify_config_env_not_found_errors ... ok
[INFO] [stdout] test config::tests::cmdify_config_env_var_used ... ok
[INFO] [stdout] test config::tests::completions_config_no_key ... ok
[INFO] [stdout] test config::tests::debug_config_file_maps_true_to_level_1_source ... ok
[INFO] [stdout] test config::tests::debug_default_false ... ok
[INFO] [stdout] test config::tests::completions_config_full ... ok
[INFO] [stdout] test config::tests::debug_env_overrides_config_file ... ok
[INFO] [stdout] test config::tests::debug_from_config_file ... ok
[INFO] [stdout] test config::tests::debug_from_env ... ok
[INFO] [stdout] test config::tests::config_file_provides_defaults ... ok
[INFO] [stdout] test config::tests::debug_from_env_false ... ok
[INFO] [stdout] test config::tests::debug_from_env_level_2 ... ok
[INFO] [stdout] test config::tests::invalid_toml_errors ... ok
[INFO] [stdout] test config::tests::max_tokens_override ... ok
[INFO] [stdout] test config::tests::explicit_config_path_not_found_errors ... ok
[INFO] [stdout] test config::tests::debug_precedence_cli_max_with_env ... ok
[INFO] [stdout] test config::tests::explicit_config_path_overrides_xdg ... ok
[INFO] [stdout] test config::tests::explicit_config_path_used ... ok
[INFO] [stdout] test config::tests::invalid_debug_env_uses_default ... ok
[INFO] [stdout] test config::tests::invalid_spinner_env_uses_default ... ok
[INFO] [stdout] test config::tests::max_tokens_from_config_file ... ok
[INFO] [stdout] test config::tests::sources_mask_api_key ... ok
[INFO] [stdout] test config::tests::invalid_bool_env_uses_default ... ok
[INFO] [stdout] test config::tests::debug_source_omitted_when_level_0 ... ok
[INFO] [stdout] test config::tests::sources_omit_defaults ... ok
[INFO] [stdout] test config::tests::sources_track_env_overrides ... ok
[INFO] [stdout] test config::tests::missing_config_file_graceful_fallback ... ok
[INFO] [stdout] test config::tests::env_var_overrides_config_file ... ok
[INFO] [stdout] test config::tests::missing_model_name ... ok
[INFO] [stdout] test config::tests::missing_provider_name ... ok
[INFO] [stdout] test config::tests::debug_from_env_true_maps_to_level_1 ... ok
[INFO] [stdout] test config::tests::tool_level_default_is_1 ... ok
[INFO] [stdout] test config::tests::unknown_provider ... ok
[INFO] [stdout] test config::tests::spinner_from_env ... ok
[INFO] [stdout] test config::tests::tool_level_from_config_file ... ok
[INFO] [stdout] test debug::tests::debug_macro_single_prefix ... ok
[INFO] [stdout] test debug::tests::emit_json_disabled_is_silent ... ok
[INFO] [stdout] test debug::tests::emit_json_requires_level_2 ... ok
[INFO] [stdout] test config::tests::spinner_from_config_file ... ok
[INFO] [stdout] test debug::tests::emit_json_silent_at_level_1 ... ok
[INFO] [stdout] test config::tests::system_prompt_env_overrides_config_file ... ok
[INFO] [stdout] test config::tests::defaults_all_false ... ok
[INFO] [stdout] test config::tests::home_fallback_when_no_xdg ... ok
[INFO] [stdout] test debug::tests::emit_line_disabled_is_silent ... ok
[INFO] [stdout] test debug::tests::emit_line_enabled_gates_on_is_enabled ... ok
[INFO] [stdout] test debug::tests::format_json_line_multiline ... ok
[INFO] [stdout] test debug::tests::format_line_contains_prefix ... ok
[INFO] [stdout] test debug::tests::format_line_with_zero_elapsed ... ok
[INFO] [stdout] test debug::tests::is_enabled_reflects_state ... ok
[INFO] [stdout] test debug::tests::level_returns_set_value ... ok
[INFO] [stdout] test config::tests::sources_track_file_origin ... ok
[INFO] [stdout] test config::tests::tool_level_clamped_to_3 ... ok
[INFO] [stdout] test config::tests::system_prompt_from_config_file ... ok
[INFO] [stdout] test app::tests::overrides_apply_quiet ... ok
[INFO] [stdout] test app::tests::safety_gate_blocks_command_substitution ... ok
[INFO] [stdout] test history::tests::max_history_lines_is_10000 ... ok
[INFO] [stdout] test history::tests::trim_not_triggered_below_limit ... ok
[INFO] [stdout] test config::tests::tool_level_from_env ... ok
[INFO] [stdout] test config::tests::tool_level_invalid_env_uses_default ... ok
[INFO] [stdout] test config::tests::xdg_takes_precedence_over_home ... ok
[INFO] [stdout] test history::tests::history_path_no_xdg_no_home ... ok
[INFO] [stdout] test history::tests::history_path_with_xdg_cache_home ... ok
[INFO] [stdout] test debug::tests::elapsed_ms_zero_before_init ... FAILED
[INFO] [stdout] test history::tests::trim_trims_oldest_entries_at_limit ... ok
[INFO] [stdout] test config::tests::spinner_env_overrides_config ... ok
[INFO] [stdout] test config::tests::missing_completions_base_url ... ok
[INFO] [stdout] test history::tests::append_creates_file_and_directory ... ok
[INFO] [stdout] test prompt::tests::prompt_tools_not_empty ... ok
[INFO] [stdout] test prompt::tests::prompt_unsafe_not_empty ... ok
[INFO] [stdout] test history::tests::append_multiple_entries ... ok
[INFO] [stdout] test history::tests::history_path_with_home_fallback ... ok
[INFO] [stdout] test prompt::tests::prompt_base_not_empty ... ok
[INFO] [stdout] test prompt::tests::os_version_included ... ok
[INFO] [stdout] test logger::tests::log_file_path_with_xdg_state_home ... ok
[INFO] [stdout] test history::tests::xdg_takes_precedence_over_home ... ok
[INFO] [stdout] test provider::anthropic::tests::create_without_key_errors ... ok
[INFO] [stdout] test logger::tests::multiple_entries_append ... ok
[INFO] [stdout] test logger::tests::new_logger_creates_file ... ok
[INFO] [stdout] test logger::tests::logger_works_without_xdg_or_home ... ok
[INFO] [stdout] test prompt::tests::custom_override_replaces_all_pieces ... ok
[INFO] [stdout] test prompt::tests::default_assembly_includes_base_tools_safety_shell ... ok
[INFO] [stdout] test prompt::tests::no_tools_excludes_tools_piece ... ok
[INFO] [stdout] test prompt::tests::no_tools_unsafe_includes_base_unsafe_shell_only ... ok
[INFO] [stdout] test prompt::tests::prompt_safety_not_empty ... ok
[INFO] [stdout] test prompt::tests::shell_detection_zsh ... ok
[INFO] [stdout] test prompt::tests::shell_detection_defaults_to_bash ... ok
[INFO] [stdout] test prompt::tests::unsafe_includes_unsafe_piece_excludes_safety ... ok
[INFO] [stdout] test prompt::tests::shell_detection_no_tools_unsafe ... ok
[INFO] [stdout] test logger::tests::log_file_path_with_xdg_override ... ok
[INFO] [stdout] test provider::anthropic::tests::supports_tools_true ... ok
[INFO] [stdout] test provider::completions::tests::format_assistant_message_with_content_and_tool_calls ... ok
[INFO] [stdout] test provider::completions::tests::format_assistant_message_with_tool_calls ... ok
[INFO] [stdout] test provider::completions::tests::format_system_message ... ok
[INFO] [stdout] test provider::completions::tests::format_tool_result_message ... ok
[INFO] [stdout] test provider::completions::tests::format_tools_empty ... ok
[INFO] [stdout] test provider::completions::tests::format_tools_includes_function_schema ... ok
[INFO] [stdout] test provider::completions::tests::format_user_message ... ok
[INFO] [stdout] test provider::completions::tests::parse_length_finish_reason ... ok
[INFO] [stdout] test provider::completions::tests::completions_provider_new ... ok
[INFO] [stdout] test provider::anthropic::tests::parse_tool_use_response ... ok
[INFO] [stdout] test provider::completions::tests::parse_stop_response ... ok
[INFO] [stdout] test provider::anthropic::tests::create_with_key ... ok
[INFO] [stdout] test provider::completions::tests::parse_tool_call_response ... ok
[INFO] [stdout] test provider::completions::tests::parse_unknown_finish_reason ... ok
[INFO] [stdout] test provider::completions::tests::parse_no_choices ... ok
[INFO] [stdout] test provider::anthropic::tests::parse_text_response ... ok
[INFO] [stdout] test provider::anthropic::tests::format_assistant_with_content_and_tool_calls ... ok
[INFO] [stdout] test provider::anthropic::tests::parse_unknown_stop_reason ... ok
[INFO] [stdout] test provider::anthropic::tests::format_assistant_text_only ... ok
[INFO] [stdout] test provider::completions::tests::completions_provider_new_optional_key ... ok
[INFO] [stdout] test provider::anthropic::tests::format_assistant_with_tool_calls ... ok
[INFO] [stdout] test provider::gemini::tests::create_without_key_errors ... ok
[INFO] [stdout] test provider::anthropic::tests::parse_mixed_text_and_tool_use ... ok
[INFO] [stdout] test provider::anthropic::tests::format_messages_extracts_system ... ok
[INFO] [stdout] test provider::anthropic::tests::format_tool_result ... ok
[INFO] [stdout] test provider::anthropic::tests::format_tools_uses_input_schema ... ok
[INFO] [stdout] test provider::anthropic::tests::parse_max_tokens_stop_reason ... ok
[INFO] [stdout] test provider::anthropic::tests::format_messages_no_system ... ok
[INFO] [stdout] test provider::completions::tests::with_options_overrides_base_url_from_config ... ok
[INFO] [stdout] test provider::gemini::tests::parse_no_candidates ... ok
[INFO] [stdout] test provider::completions::tests::with_options_sets_provider_name ... ok
[INFO] [stdout] test provider::gemini::tests::build_url_includes_model_and_key ... ok
[INFO] [stdout] test provider::http::tests::user_agent_contains_cmdify ... ok
[INFO] [stdout] test provider::gemini::tests::build_url_trims_base_slash ... ok
[INFO] [stdout] test provider::gemini::tests::parse_stop_ambiguity_resolved_for_text ... ok
[INFO] [stdout] test provider::huggingface::tests::create_without_key_errors ... ok
[INFO] [stdout] test provider::gemini::tests::parse_multiple_function_calls ... ok
[INFO] [stdout] test provider::gemini::tests::create_with_key ... ok
[INFO] [stdout] test provider::gemini::tests::format_assistant_with_tool_calls ... ok
[INFO] [stdout] test provider::kimi::tests::create_without_key_errors ... ok
[INFO] [stdout] test provider::minimax::tests::create_without_key_errors ... ok
[INFO] [stdout] test provider::mistral::tests::create_without_key_errors ... ok
[INFO] [stdout] test provider::gemini::tests::format_tool_result ... ok
[INFO] [stdout] test provider::gemini::tests::format_assistant_text_only ... ok
[INFO] [stdout] test provider::openai::tests::create_without_key_errors ... ok
[INFO] [stdout] test provider::gemini::tests::format_messages_extracts_system ... ok
[INFO] [stdout] test provider::openrouter::tests::create_without_key_errors ... ok
[INFO] [stdout] test provider::gemini::tests::format_tools_uses_function_declarations ... ok
[INFO] [stdout] test provider::qwen::tests::create_without_key_errors ... ok
[INFO] [stdout] test provider::responses::tests::format_assistant_message_with_tool_calls ... ok
[INFO] [stdout] test provider::responses::tests::format_assistant_with_content_and_tool_calls ... ok
[INFO] [stdout] test provider::gemini::tests::format_assistant_with_content_and_tool_calls ... ok
[INFO] [stdout] test provider::responses::tests::format_tool_result ... ok
[INFO] [stdout] test provider::responses::tests::format_tools_empty ... ok
[INFO] [stdout] test provider::responses::tests::format_tools_flat ... ok
[INFO] [stdout] test provider::responses::tests::format_user_message ... ok
[INFO] [stdout] test provider::responses::tests::parse_empty_output ... ok
[INFO] [stdout] test provider::gemini::tests::format_messages_no_system ... ok
[INFO] [stdout] test provider::gemini::tests::parse_function_call_response ... ok
[INFO] [stdout] test provider::responses::tests::parse_incomplete_status ... ok
[INFO] [stdout] test provider::responses::tests::parse_no_output_key ... ok
[INFO] [stdout] test provider::responses::tests::parse_mixed_text_and_function_call ... ok
[INFO] [stdout] test provider::responses::tests::parse_text_response ... ok
[INFO] [stdout] test provider::responses::tests::parse_skips_reasoning_items ... ok
[INFO] [stdout] test provider::responses::tests::format_system_message ... ok
[INFO] [stdout] test provider::responses::tests::parse_function_call_response ... ok
[INFO] [stdout] test provider::gemini::tests::parse_stop_ambiguity_resolved_for_tool_calls ... ok
[INFO] [stdout] test provider::gemini::tests::supports_tools_true ... ok
[INFO] [stdout] test provider::zai::tests::create_without_key_errors ... ok
[INFO] [stdout] test safety::tests::append_redirect_allowed ... ok
[INFO] [stdout] test safety::tests::block_device_target_blocked ... ok
[INFO] [stdout] test provider::huggingface::tests::create_with_key ... ok
[INFO] [stdout] test safety::tests::blocked_command_substitution ... ok
[INFO] [stdout] test safety::tests::blocked_crontab_remove ... ok
[INFO] [stdout] test safety::tests::blocked_dd ... ok
[INFO] [stdout] test safety::tests::blocked_eval ... ok
[INFO] [stdout] test safety::tests::blocked_exec_with_substitution ... ok
[INFO] [stdout] test safety::tests::blocked_fdisk ... ok
[INFO] [stdout] test safety::tests::blocked_fork_bomb ... ok
[INFO] [stdout] test safety::tests::blocked_leading_redirect_truncation ... ok
[INFO] [stdout] test safety::tests::blocked_mkfs ... ok
[INFO] [stdout] test safety::tests::blocked_modprobe ... ok
[INFO] [stdout] test safety::tests::blocked_mv_to_dev_null ... ok
[INFO] [stdout] test provider::mistral::tests::create_with_key ... ok
[INFO] [stdout] test safety::tests::blocked_pipe_to_sh ... ok
[INFO] [stdout] test safety::tests::blocked_pipe_to_zsh ... ok
[INFO] [stdout] test safety::tests::blocked_reboot ... ok
[INFO] [stdout] test safety::tests::blocked_backtick_substitution ... ok
[INFO] [stdout] test provider::minimax::tests::create_with_key ... ok
[INFO] [stdout] test safety::tests::blocked_redirect_to_block_device_sda ... ok
[INFO] [stdout] test safety::tests::blocked_redirect_to_block_device_with_command ... ok
[INFO] [stdout] test safety::tests::blocked_shutdown ... ok
[INFO] [stdout] test safety::tests::blocked_redirect_to_block_device_append ... ok
[INFO] [stdout] test safety::tests::blocked_variable_expansion_home ... ok
[INFO] [stdout] test provider::ollama::tests::create_ollama_provider ... ok
[INFO] [stdout] test safety::tests::chmod_777_no_recursive_safe ... ok
[INFO] [stdout] test safety::tests::chained_command_blocked ... ok
[INFO] [stdout] test provider::openai::tests::create_with_key ... ok
[INFO] [stdout] test safety::tests::blocked_redirect_to_block_device_nvme ... ok
[INFO] [stdout] test safety::tests::crontab_edit_allowed ... ok
[INFO] [stdout] test safety::tests::chmod_r_777_blocked ... ok
[INFO] [stdout] test safety::tests::dd_already_blocked_by_pass2 ... ok
[INFO] [stdout] test safety::tests::cp_to_etc_safe ... ok
[INFO] [stdout] test safety::tests::empty_command_safe ... ok
[INFO] [stdout] test safety::tests::crontab_list_allowed ... ok
[INFO] [stdout] test safety::tests::dev_nvme_target_blocked ... ok
[INFO] [stdout] test safety::tests::echo_string_safe ... ok
[INFO] [stdout] test safety::tests::find_delete_home_blocked ... ok
[INFO] [stdout] test safety::tests::find_delete_scoped_allowed ... ok
[INFO] [stdout] test safety::tests::find_delete_broad_root_blocked ... ok
[INFO] [stdout] test safety::tests::find_exec_chmod_777_broad_blocked ... ok
[INFO] [stdout] test safety::tests::find_exec_grep_broad_allowed ... ok
[INFO] [stdout] test safety::tests::find_exec_rm_rf_scoped_allowed ... ok
[INFO] [stdout] test safety::tests::find_exec_rm_no_dangerous_flags_allowed ... ok
[INFO] [stdout] test safety::tests::find_maxdepth_delete_scoped_allowed ... ok
[INFO] [stdout] test safety::tests::find_execdir_rm_rf_broad_blocked ... ok
[INFO] [stdout] test safety::tests::kill_9_minus1_blocked ... ok
[INFO] [stdout] test safety::tests::kill_normal_safe ... ok
[INFO] [stdout] test safety::tests::mkfs_already_blocked_by_pass2 ... ok
[INFO] [stdout] test safety::tests::mv_normal_allowed ... ok
[INFO] [stdout] test provider::openrouter::tests::create_with_key ... ok
[INFO] [stdout] test safety::tests::pipe_to_cat_safe ... ok
[INFO] [stdout] test safety::tests::mv_scoped_safe ... ok
[INFO] [stdout] test safety::tests::quoted_path_with_dangerous_flags ... ok
[INFO] [stdout] test safety::tests::pipe_to_sh_in_chain ... ok
[INFO] [stdout] test safety::tests::rm_dash_r_force_allowed ... ok
[INFO] [stdout] test safety::tests::rm_fr_blocked ... ok
[INFO] [stdout] test safety::tests::rm_i_safe ... ok
[INFO] [stdout] test safety::tests::rm_no_preserve_root_blocked ... ok
[INFO] [stdout] test safety::tests::rm_r_safe ... ok
[INFO] [stdout] test safety::tests::rm_rf_broad_target_blocked ... ok
[INFO] [stdout] test provider::kimi::tests::create_with_key ... ok
[INFO] [stdout] test safety::tests::rm_rf_combined_flag_blocked ... ok
[INFO] [stdout] test provider::responses::tests::responses_provider_new ... ok
[INFO] [stdout] test safety::tests::rm_rf_home_blocked ... ok
[INFO] [stdout] test safety::tests::rm_rf_scoped_allowed ... ok
[INFO] [stdout] test safety::tests::rm_rf_etc_blocked ... ok
[INFO] [stdout] test safety::tests::rm_rf_etc_passwd_blocked ... ok
[INFO] [stdout] test safety::tests::rm_rf_tmp_stale_allowed ... ok
[INFO] [stdout] test safety::tests::safe_command_echo ... ok
[INFO] [stdout] test safety::tests::safe_command_git ... ok
[INFO] [stdout] test safety::tests::safe_command_ls ... ok
[INFO] [stdout] test safety::tests::safe_command_pipe_grep ... ok
[INFO] [stdout] test safety::tests::rm_rf_variable_dir_allowed ... ok
[INFO] [stdout] test safety::tests::safe_exec_plain ... ok
[INFO] [stdout] test safety::tests::split_chained_empty_parts ... ok
[INFO] [stdout] test safety::tests::semicolon_chain_blocked ... ok
[INFO] [stdout] test safety::tests::split_chained_pipe ... ok
[INFO] [stdout] test safety::tests::split_chained_quoted ... ok
[INFO] [stdout] test safety::tests::split_chained_semicolon ... ok
[INFO] [stdout] test safety::tests::split_chained_simple ... ok
[INFO] [stdout] test safety::tests::split_chained_triple ... ok
[INFO] [stdout] test safety::tests::subshell_in_middle_blocked ... ok
[INFO] [stdout] test safety::tests::sudo_rm_rf_root_blocked ... ok
[INFO] [stdout] test safety::tests::safe_command_find ... ok
[INFO] [stdout] test safety::tests::wildcard_in_path_safe ... ok
[INFO] [stdout] test safety::tests::whitespace_only_safe ... ok
[INFO] [stdout] test safety::tests::wildcard_standalone_blocked ... ok
[INFO] [stdout] test safety::tests::blocked_pipe_to_bash ... ok
[INFO] [stdout] test safety::tests::cat_safe ... ok
[INFO] [stdout] test safety::tests::find_delete_broad_etc_blocked ... ok
[INFO] [stdout] test safety::tests::find_exec_rm_rf_broad_blocked ... ok
[INFO] [stdout] test safety::tests::find_name_only_allowed ... ok
[INFO] [stdout] test safety::tests::rm_dash_r_dash_f_allowed ... ok
[INFO] [stdout] test spinner::tests::pause_and_resume ... ok
[INFO] [stdout] test spinner::tests::pause_handle_cloned_independently ... ok
[INFO] [stdout] test tools::ask_user::tests::format_display_output ... ok
[INFO] [stdout] test tools::ask_user::tests::parse_choices_missing_key ... ok
[INFO] [stdout] test tools::ask_user::tests::parse_choices_empty_array ... ok
[INFO] [stdout] test tools::ask_user::tests::parse_choices_whitespace_key_trimmed ... ok
[INFO] [stdout] test spinner::tests::pause_handle_defaults_not_paused ... ok
[INFO] [stdout] test tools::ask_user::tests::missing_choices_argument ... ok
[INFO] [stdout] test tools::ask_user::tests::read_choice_single_key ... ok
[INFO] [stdout] test tools::ask_user::tests::read_choice_valid_lowercase ... ok
[INFO] [stdout] test tools::ask_user::tests::read_choice_valid_uppercase ... ok
[INFO] [stdout] test tools::ask_user::tests::read_choice_whitespace_only_returns_sentinel ... ok
[INFO] [stdout] test tools::ask_user::tests::parse_choices_missing_label ... ok
[INFO] [stdout] test tools::ask_user::tests::parse_choices_valid ... ok
[INFO] [stdout] test tools::ask_user::tests::tool_definition_has_required_fields ... ok
[INFO] [stdout] test tools::ask_user::tests::tool_name ... ok
[INFO] [stdout] test tools::ask_user::tests::tool_definition_choices_schema ... ok
[INFO] [stdout] test tools::ask_user::tests::read_choice_empty_returns_sentinel ... ok
[INFO] [stdout] test tools::ask_user::tests::parse_choices_empty_key ... ok
[INFO] [stdout] test tools::ask_user::tests::read_choice_invalid_returns_note ... ok
[INFO] [stdout] test tools::ask_user::tests::read_choice_with_trailing_whitespace ... ok
[INFO] [stdout] test tools::find_command::tests::command_not_string_errors ... ok
[INFO] [stdout] test tools::ask_user::tests::missing_question_argument ... ok
[INFO] [stdout] test tools::find_command::tests::missing_command_argument ... ok
[INFO] [stdout] test tools::find_command::tests::command_with_path ... ok
[INFO] [stdout] test tools::ask_user::tests::format_display_single_choice ... ok
[INFO] [stdout] test tools::find_command::tests::tool_definition_has_required_fields ... ok
[INFO] [stdout] test tools::tests::ask_user_excluded_when_quiet ... ok
[INFO] [stdout] test tools::find_command::tests::find_existing_command_ls ... ok
[INFO] [stdout] test tools::tests::execute_unknown_tool_errors ... ok
[INFO] [stdout] test tools::tests::find_command_excluded_when_blind ... ok
[INFO] [stdout] test tools::tests::level_0_empty_registry ... ok
[INFO] [stdout] test tools::tests::level_2_plus_blind ... ok
[INFO] [stdout] test tools::tests::level_2_same_as_level_1 ... ok
[INFO] [stdout] test tools::tests::level_3_plus_no_tools ... ok
[INFO] [stdout] test provider::ollama::tests::create_ollama_custom_url ... ok
[INFO] [stdout] test tools::find_command::tests::tool_name ... ok
[INFO] [stdout] test tools::find_command::tests::find_existing_command_sh ... ok
[INFO] [stdout] test tools::tests::registry_quiet_and_blind ... ok
[INFO] [stdout] test tools::tests::registry_with_both_tools ... ok
[INFO] [stdout] test tools::tests::registry_all_flags_set ... ok
[INFO] [stdout] test tools::find_command::tests::find_nonexistent_command ... ok
[INFO] [stdout] test tools::tests::registry_with_no_tools_flag ... ok
[INFO] [stdout] test tools::tests::registry_with_quiet_flag ... ok
[INFO] [stdout] test tools::find_command::tests::shell_injection_safety ... ok
[INFO] [stdout] test tools::find_command::tests::timeout_returns_error_message ... ok
[INFO] [stdout] test tools::ask_user::tests::parse_choices_missing_choices_field ... ok
[INFO] [stdout] test tools::tests::execute_find_command_directly ... ok
[INFO] [stdout] test provider::qwen::tests::create_with_key ... ok
[INFO] [stdout] test provider::gemini::tests::parse_text_response ... ok
[INFO] [stdout] test provider::responses::tests::with_options_sets_provider_name ... ok
[INFO] [stdout] test provider::responses::tests::with_options_overrides_base_url_from_config ... ok
[INFO] [stdout] test provider::zai::tests::create_with_key ... ok
[INFO] [stdout] test provider::responses::tests::responses_provider_new_optional_key ... ok
[INFO] [stdout] test tools::tests::registry_with_blind_flag ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- debug::tests::elapsed_ms_zero_before_init stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'debug::tests::elapsed_ms_zero_before_init' (133) panicked at src/debug.rs:167:9:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: 31
[INFO] [stdout]  right: 0
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x61043dcc5bf8 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x61043dcc5bf8 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x61043dcc5bf8 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x61043dcc5bf8 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x61043dcddbda - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x61043dcddbda - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x61043dccb072 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x61043dccb072 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x61043dca1606 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x61043dca1606 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x61043dcbcd49 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x61043d59dbfc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x61043d59dbfc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x61043dcbcfc2 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x61043dcbcfc2 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x61043dca16c2 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x61043dc96369 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x61043dca27dd - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x61043dcde39c - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x61043dcde253 - core[28b146924c2f19c6]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:439:17
[INFO] [stdout]   20:     0x61043d47383c - core[28b146924c2f19c6]::panicking::assert_failed::<u128, u128>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x61043d499528 - cmdify[1610f1709afb48a4]::debug::tests::elapsed_ms_zero_before_init
[INFO] [stdout]                                at /opt/rustwide/workdir/src/debug.rs:167:9
[INFO] [stdout]   22:     0x61043d495387 - cmdify[1610f1709afb48a4]::debug::tests::elapsed_ms_zero_before_init::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/debug.rs:165:37
[INFO] [stdout]   23:     0x61043d442ae6 - <cmdify[1610f1709afb48a4]::debug::tests::elapsed_ms_zero_before_init::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x61043d590cdb - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x61043d590cdb - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x61043d59e555 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x61043d59e555 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x61043d59e555 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x61043d59e555 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x61043d59e555 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x61043d59e555 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x61043d59e555 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x61043d5990b4 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x61043d5990b4 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x61043d5a15d2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x61043d5a15d2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x61043d5a15d2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x61043d5a15d2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x61043d5a15d2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x61043d5a15d2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x61043d5a15d2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x61043dcc4e4f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   43:     0x61043dcc4e4f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x7745fef6daa4 - <unknown>
[INFO] [stdout]   45:     0x7745feffaa64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     debug::tests::elapsed_ms_zero_before_init
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 370 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 12.31s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "ab2a2af7ab6bfe2816112010335003c7fb727b4e6ef7005ede29cad1728ad68b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ab2a2af7ab6bfe2816112010335003c7fb727b4e6ef7005ede29cad1728ad68b", kill_on_drop: false }`
[INFO] [stdout] ab2a2af7ab6bfe2816112010335003c7fb727b4e6ef7005ede29cad1728ad68b
